--------------------------------- Captured Out --------------------------------- ---- new test ---- 1771059345.199 sable_ircd uid=1001 1771059345.199 sable_services uid=1001 1771059345.200 sable_services #x1B[2m2026-02-14T08:55:45.199953Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting network state from PeerConfig { name: ServerName("My.Little.History"), address: "127.0.0.1:49231", fingerprint: "791186d832c0402a94f04775d8aebcd968c100b6" } 1771059345.200 sable_services #x1B[2m2026-02-14T08:55:45.200188Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059345), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.History#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Could not connect to 127.0.0.1:49231. Last error: I/O error: Connection refused (os error 111) 1771059345.200 sable_services #x1B[2m2026-02-14T08:55:45.200242Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059345), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.History#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mI/O error: Connection refused (os error 111) 1771059345.203 sable_ircd #x1B[2m2026-02-14T08:55:45.202325Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434094230933504)), timestamp: 1771059345, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1771059345, name: ServerName("My.Little.Server"), ts: 1771059345, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock() my clock=EventClock() 1771059345.203 sable_ircd #x1B[2m2026-02-14T08:55:45.202441Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059345.203 sable_ircd #x1B[2m2026-02-14T08:55:45.202635Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from reap_preclients_timer 1771059345.203 sable_ircd #x1B[2m2026-02-14T08:55:45.202695Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun#x1B[0m#x1B[2m:#x1B[0m#x1B[1mapply_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mevent#x1B[0m#x1B[2m=#x1B[0mEvent { id: EventId(Snowflake(810434094230933504)), timestamp: 1771059345, clock: EventClock(), target: Server(ServerId(1)), details: NewServer(NewServer { epoch: 1771059345, name: ServerName("My.Little.Server"), ts: 1771059345, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_server#x1B[0m#x1B[1m{#x1B[0m#x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mNewServer { server: ServerId(1) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Server 1771059345.203 sable_ircd #x1B[2m2026-02-14T08:55:45.202850Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434094230933504)), timestamp: 1771059345, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] }) 1771059345.203 sable_ircd #x1B[2m2026-02-14T08:55:45.202866Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434094230933505)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933504), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1771059345 }) }; event clock=EventClock(1 => 810434094230933504) my clock=EventClock(1 => 810434094230933504) 1771059345.203 sable_ircd #x1B[2m2026-02-14T08:55:45.202895Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434094230933504)), timestamp: 1771059345, change: NewServer(NewServer { server: ServerId(1) }), users_to_notify: [] } 1771059345.203 sable_ircd #x1B[2m2026-02-14T08:55:45.202921Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059345.203 sable_ircd #x1B[2m2026-02-14T08:55:45.202945Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434094230933504)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059345.203 sable_ircd #x1B[2m2026-02-14T08:55:45.202977Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434094230933504)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] } 1771059345.203 sable_ircd #x1B[2m2026-02-14T08:55:45.203135Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434094230933505)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059345.203 sable_ircd #x1B[2m2026-02-14T08:55:45.203169Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434094230933505)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] } 1771059345.226 sable_ircd #x1B[2m2026-02-14T08:55:45.225925Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434094327402499)), timestamp: 1771059345, clock: EventClock(1 => 810433843147313154), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1771059345 }) }; event clock=EventClock(1 => 810433843147313154) my clock=EventClock(1 => 810433843147313154) 1771059345.226 sable_ircd #x1B[2m2026-02-14T08:55:45.226011Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059345.226 sable_ircd #x1B[2m2026-02-14T08:55:45.226212Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from reap_preclients_timer 1771059345.226 sable_ircd #x1B[2m2026-02-14T08:55:45.226275Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434094327402499)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059345.226 sable_ircd #x1B[2m2026-02-14T08:55:45.226301Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434094327402499)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] } 1771059345.260 sable_services #x1B[2m2026-02-14T08:55:45.259640Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting network state from PeerConfig { name: ServerName("My.Little.Server"), address: "127.0.0.1:34905", fingerprint: "6c316d120233227cfd1229e579dd613cea7699d4" } 1771059345.260 sable_services #x1B[2m2026-02-14T08:55:45.259971Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059345), content: GetNetworkState } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34905 (127.0.0.1:34905) 1771059345.267 sable_ircd #x1B[2m2026-02-14T08:55:45.267122Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::node#x1B[0m#x1B[2m:#x1B[0m Server got state export request; sending 1771059345.295 sable_ircd #x1B[2m2026-02-14T08:55:45.295046Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m got new connection #x1B[3mdata#x1B[0m#x1B[2m=#x1B[0mConnectionData { id: ConnectionId(ListenerId(0), 1), remote_addr: 127.0.0.1, tls_info: None } 1771059345.295 sable_ircd #x1B[2m2026-02-14T08:55:45.295177Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059345.295 sable_ircd #x1B[2m2026-02-14T08:55:45.295236Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 1)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got new connection 1771059345.295 sable_ircd #x1B[2m2026-02-14T08:55:45.295308Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 1)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 1): :My.Little.Server NOTICE * :*** Looking up your hostname 1771059345.295 sable_ircd #x1B[2m2026-02-14T08:55:45.295454Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059345.296 sable_ircd #x1B[2m2026-02-14T08:55:45.295518Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 1)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"QUIT :chkport" 1771059345.296 sable_ircd #x1B[2m2026-02-14T08:55:45.295635Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 1): ERROR :Client quit 1771059345.296 sable_ircd #x1B[2m2026-02-14T08:55:45.295775Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mCloseConnection(ConnectionId(ListenerId(0), 1)) 1771059345.296 sable_ircd #x1B[2m2026-02-14T08:55:45.295822Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::connection_collection#x1B[0m#x1B[2m:#x1B[0m Removing connection ConnectionId(ListenerId(0), 1) 1771059345.296 sable_ircd #x1B[2m2026-02-14T08:55:45.296276Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m Connection error #x1B[3mconnection#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 1) #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mClosed 1771059345.296 sable_ircd #x1B[2m2026-02-14T08:55:45.296345Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059345.297 sable_ircd #x1B[2m2026-02-14T08:55:45.296520Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from auth_events 1771059345.308 sable_services #x1B[2m2026-02-14T08:55:45.308317Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Bootstrap message: Message { source_server: (ServerId(1), 1771059345), content: NetworkState(Network { nick_bindings: {}, historic_nick_users: HistoricNickStore { data: {} }, users: {}, historic_users: HistoricUserStore { users: {} }, user_connections: {}, channels: {}, list_mode_entries: {}, channel_topics: {}, channel_invites: {}, memberships: {}, messages: {}, servers: {ServerId(1): Server { id: ServerId(1), epoch: 1771059345, name: ServerName("My.Little.Server"), last_ping: 1771059345, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }}, network_bans: BanRepository { pre_registration_bans: {}, new_connection_bans: {}, pre_sasl_bans: {}, pre_registration_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: ["", "", "", "", "", ""], uint64: [], regex: [] }, fields: {"user_param_1": (3, String(_)), "tls": (0, Boolean(_)), "user_param_2": (0, String(_)), "host": (5, String(_)), "ip": (0, Ip(_)), "user": (1, String(_)), "realname": (2, String(_)), "nick": (4, String(_))} }, new_connection_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: [], uint64: [], regex: [] }, fields: {"tls": (0, Boolean(_)), "ip": (0, Ip(_))} }, pre_sasl_engine: Engine { operations: [], constants: Scratch { boolean: [], cidr: [], int64: [], ip: [], string: [], uint64: [], regex: [] }, reference_dynamics: Scratch { boolean: [false], cidr: [], int64: [], ip: [0.0.0.0], string: [""], uint64: [], regex: [] }, fields: {"tls": (0, Boolean(_)), "ip": (0, Ip(_)), "mechanism": (0, String(_))} } }, audit_log: {}, accounts: {}, nick_registrations: {}, channel_registrations: {}, channel_accesses: {}, channel_roles: {}, current_services: None, current_history_server_id: None, config: NetworkConfig { opers: [OperConfig { name: "operuser", hash: "$6$z5yA.OfGliDoi/R2$BgSsguS6bxAsPSCygDisgDw5JZuo5.88eU3Hyc7/4OaNpeKIxWGjOggeHzOl0xLiZg1vfwxXjOTFN14wG5vNI." }], debug_mode: true, default_roles: {BuiltinVoice: ChannelAccessSet(4296081441), BuiltinAll: ChannelAccessSet(1114112), BuiltinOp: ChannelAccessSet(222192934775)}, alias_users: [AliasUser { nick: Nickname("ChanServ"), user: Username("ChanServ"), host: Hostname("services."), realname: Realname("Channel services compatibility layer"), command_alias: "CS" }, AliasUser { nick: Nickname("NickServ"), user: Username("NickServ"), host: Hostname("services."), realname: Realname("Account services compatibility layer"), command_alias: "NS" }], object_expiry: 60, pingout_duration: 600 }, clock: EventClock(1 => 810434094230933505), cache_default_channel_roles: OnceLock(), alias_users: OnceLock() }) } 1771059345.309 sable_services #x1B[2m2026-02-14T08:55:45.308491Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_to_network#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Server 1771059345.309 sable_services #x1B[2m2026-02-14T08:55:45.308609Z#x1B[0m #x1B[33m WARN#x1B[0m #x1B[2msable_services::database::jsonfile#x1B[0m#x1B[2m:#x1B[0m Couldn't open database file, starting from empty 1771059345.309 sable_services #x1B[2m2026-02-14T08:55:45.309182Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434094680125440)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059345, name: ServerName("My.Little.Services"), ts: 1771059345, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 810434094230933505) my clock=EventClock(1 => 810434094230933505) 1771059345.310 sable_services #x1B[2m2026-02-14T08:55:45.309454Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434094680125440)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059345, name: ServerName("My.Little.Services"), ts: 1771059345, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34905 (127.0.0.1:34905) 1771059345.311 sable_services #x1B[2m2026-02-14T08:55:45.311313Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434094688514049)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505, 99 => 810434094680125440), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059345 }) }; event clock=EventClock(1 => 810434094230933505, 99 => 810434094680125440) my clock=EventClock(1 => 810434094230933505, 99 => 810434094680125440) 1771059345.312 sable_services #x1B[2m2026-02-14T08:55:45.311685Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434094688514049)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505, 99 => 810434094680125440), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059345 }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34905 (127.0.0.1:34905) 1771059345.312 sable_services #x1B[2m2026-02-14T08:55:45.311692Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun#x1B[0m#x1B[2m:#x1B[0m#x1B[1mapply_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mevent#x1B[0m#x1B[2m=#x1B[0mEvent { id: EventId(Snowflake(810434094680125440)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059345, name: ServerName("My.Little.Services"), ts: 1771059345, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_server#x1B[0m#x1B[1m{#x1B[0m#x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mNewServer { server: ServerId(99) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Services 1771059345.315 sable_services #x1B[2m2026-02-14T08:55:45.315197Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434094705291266)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505, 99 => 810434094688514049), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 810434094230933505, 99 => 810434094688514049) my clock=EventClock(1 => 810434094230933505, 99 => 810434094688514049) 1771059345.316 sable_services #x1B[2m2026-02-14T08:55:45.315480Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434094705291266)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505, 99 => 810434094688514049), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34905 (127.0.0.1:34905) 1771059345.316 chkSvs: connects to server. 1771059345.316 chkSvs -> S: NICK chkSvs 1771059345.316 chkSvs -> S: USER chk chk chk chk 1771059345.316 sable_ircd #x1B[2m2026-02-14T08:55:45.316269Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434094688514049)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505, 99 => 810434094680125440), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059345 }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(810434094688514049)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505, 99 => 810434094680125440), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059345 }) }; event clock=EventClock(1 => 810434094230933505, 99 => 810434094680125440) my clock=EventClock(1 => 810434094230933505) 1771059345.316 sable_ircd #x1B[2m2026-02-14T08:55:45.316352Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434094688514049)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505, 99 => 810434094680125440), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059345 }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(810434094680125440))] 1771059345.316 sable_ircd #x1B[2m2026-02-14T08:55:45.316406Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434094688514049)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505, 99 => 810434094680125440), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059345 }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059345.317 sable_ircd #x1B[2m2026-02-14T08:55:45.316582Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m got new connection #x1B[3mdata#x1B[0m#x1B[2m=#x1B[0mConnectionData { id: ConnectionId(ListenerId(0), 2), remote_addr: 127.0.0.1, tls_info: None } 1771059345.317 sable_ircd #x1B[2m2026-02-14T08:55:45.316719Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059345.317 sable_ircd #x1B[2m2026-02-14T08:55:45.316792Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got new connection 1771059345.317 sable_ircd #x1B[2m2026-02-14T08:55:45.316859Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server NOTICE * :*** Looking up your hostname 1771059345.317 sable_ircd #x1B[2m2026-02-14T08:55:45.316981Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059345.317 sable_ircd #x1B[2m2026-02-14T08:55:45.317030Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"NICK chkSvs" 1771059345.317 sable_ircd #x1B[2m2026-02-14T08:55:45.317220Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 373, tv_nsec: 383092848 }, attach_user_id: OnceLock(), user: OnceLock(), extra_user_params: OnceLock(), nick: OnceLock(Nickname("chkSvs")), realname: OnceLock(), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059345.317 sable_ircd #x1B[2m2026-02-14T08:55:45.317305Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from async_handlers 1771059345.317 sable_ircd #x1B[2m2026-02-14T08:55:45.317360Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059345.317 sable_ircd #x1B[2m2026-02-14T08:55:45.317412Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"USER chk chk chk chk" 1771059345.318 sable_ircd #x1B[2m2026-02-14T08:55:45.317504Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 373, tv_nsec: 383092848 }, attach_user_id: OnceLock(), user: OnceLock(Username("chk")), extra_user_params: OnceLock(("chk", "chk")), nick: OnceLock(Nickname("chkSvs")), realname: OnceLock(Realname("chk")), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059345.318 sable_ircd #x1B[2m2026-02-14T08:55:45.318319Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from auth_events 1771059345.318 sable_ircd #x1B[2m2026-02-14T08:55:45.318360Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m DNS lookup finished for ConnectionId(ListenerId(0), 2): 127.0.0.1/Some(Hostname("localhost")) 1771059345.318 sable_ircd #x1B[2m2026-02-14T08:55:45.318393Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server NOTICE * :*** Found your hostname: localhost 1771059345.318 sable_ircd #x1B[2m2026-02-14T08:55:45.318415Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 373, tv_nsec: 383092848 }, attach_user_id: OnceLock(), user: OnceLock(Username("chk")), extra_user_params: OnceLock(("chk", "chk")), nick: OnceLock(Nickname("chkSvs")), realname: OnceLock(Realname("chk")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059345.318 sable_ircd #x1B[2m2026-02-14T08:55:45.318456Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mRegisterClient(ConnectionId(ListenerId(0), 2)) 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.318669Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: User(UserId(Snowflake(810434094717472768))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434094717472769)), NewUserConnection { user: UserId(Snowflake(810434094717472768)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059345 })) }) }; event clock=EventClock(1 => 810434094230933505) my clock=EventClock(1 => 810434094230933505) 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.318785Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319081Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(810434094717472768)), 1) }), users_to_notify: [] }) 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319122Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(810434094717472768)), 1) }), users_to_notify: [] } 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319178Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(810434094717472768)), 2), connection: UserConnectionId(Snowflake(810434094717472769)) }), users_to_notify: [] }) 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319203Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(810434094717472768)), 2), connection: UserConnectionId(Snowflake(810434094717472769)) }), users_to_notify: [] } 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319248Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 001 chkSvs :Welcome to the test Internet Relay Chat network, chkSvs 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319274Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 002 chkSvs :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319298Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 003 chkSvs :This server was created 2026-02-14 08:55:45.319289533 UTC 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319316Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 004 chkSvs My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319355Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 005 chkSvs EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319377Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server 422 chkSvs :MOTD File is missing 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319397Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :chkSvs!chk@localhost MODE chkSvs + 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319413Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server NOTICE chkSvs :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319445Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059345.319 sable_ircd #x1B[2m2026-02-14T08:55:45.319464Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] } 1771059345.352 sable_ircd #x1B[2m2026-02-14T08:55:45.352058Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434094680125440)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059345, name: ServerName("My.Little.Services"), ts: 1771059345, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434094680125440)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059345, name: ServerName("My.Little.Services"), ts: 1771059345, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }; event clock=EventClock(1 => 810434094230933505) my clock=EventClock(1 => 810434094717472770) 1771059345.352 sable_ircd #x1B[2m2026-02-14T08:55:45.352132Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434094680125440)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059345, name: ServerName("My.Little.Services"), ts: 1771059345, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(810434094688514049)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505, 99 => 810434094680125440), target: Server(ServerId(99)), details: ServerPing(ServerPing { ts: 1771059345 }) } 1771059345.352 sable_ircd #x1B[2m2026-02-14T08:55:45.352180Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434094680125440)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059345, name: ServerName("My.Little.Services"), ts: 1771059345, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059345.352 sable_ircd #x1B[2m2026-02-14T08:55:45.352419Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun#x1B[0m#x1B[2m:#x1B[0m#x1B[1mapply_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mevent#x1B[0m#x1B[2m=#x1B[0mEvent { id: EventId(Snowflake(810434094680125440)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: Server(ServerId(99)), details: NewServer(NewServer { epoch: 1771059345, name: ServerName("My.Little.Services"), ts: 1771059345, flags: (empty), version: "sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432" }) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_server#x1B[0m#x1B[1m{#x1B[0m#x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mNewServer { server: ServerId(99) }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m enabling peer My.Little.Services 1771059345.353 sable_ircd #x1B[2m2026-02-14T08:55:45.352590Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434094680125440)), timestamp: 1771059345, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] }) 1771059345.353 sable_ircd #x1B[2m2026-02-14T08:55:45.352619Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434094680125440)), timestamp: 1771059345, change: NewServer(NewServer { server: ServerId(99) }), users_to_notify: [] } 1771059345.353 sable_ircd #x1B[2m2026-02-14T08:55:45.352653Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434094680125440)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059345.353 sable_ircd #x1B[2m2026-02-14T08:55:45.352668Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434094680125440)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] } 1771059345.353 sable_ircd #x1B[2m2026-02-14T08:55:45.352689Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434094688514049)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059345.353 sable_ircd #x1B[2m2026-02-14T08:55:45.352703Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434094688514049)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] } 1771059345.357 sable_services #x1B[2m2026-02-14T08:55:45.357038Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: GetEvent([EventId(Snowflake(810434094680125440))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(810434094680125440))] 1771059345.357 sable_services #x1B[2m2026-02-14T08:55:45.357077Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: GetEvent([EventId(Snowflake(810434094680125440))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [] 1771059345.360 sable_ircd #x1B[2m2026-02-14T08:55:45.360018Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434094705291266)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505, 99 => 810434094688514049), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434094705291266)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505, 99 => 810434094688514049), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }; event clock=EventClock(1 => 810434094230933505, 99 => 810434094688514049) my clock=EventClock(1 => 810434094717472770, 99 => 810434094688514049) 1771059345.360 sable_ircd #x1B[2m2026-02-14T08:55:45.360334Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434094705291266)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505, 99 => 810434094688514049), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434094705291266)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505, 99 => 810434094688514049), target: Server(ServerId(99)), details: IntroduceServicesServer(IntroduceServicesServer { sasl_mechanisms: ["PLAIN"] }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34379 (127.0.0.1:34379) 1771059345.364 sable_ircd #x1B[2m2026-02-14T08:55:45.363877Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434094705291266)), timestamp: 1771059345, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] }) 1771059345.364 sable_ircd #x1B[2m2026-02-14T08:55:45.363929Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434094705291266)), timestamp: 1771059345, change: ServicesUpdate(ServicesUpdate), users_to_notify: [] } 1771059345.364 sable_ircd #x1B[2m2026-02-14T08:55:45.364046Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434094705291266)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059345.364 sable_ircd #x1B[2m2026-02-14T08:55:45.364084Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434094705291266)), timestamp: 1771059345, change: EventComplete(EventComplete), users_to_notify: [] } 1771059345.398 sable_ircd #x1B[2m2026-02-14T08:55:45.397979Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: BulkEvents([]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [] 1771059345.416 S -> chkSvs: :My.Little.Server NOTICE * :*** Looking up your hostname 1771059345.416 S -> chkSvs: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1771059345.416 S -> chkSvs: :My.Little.Server 001 chkSvs :Welcome to the test Internet Relay Chat network, chkSvs 1771059345.416 S -> chkSvs: :My.Little.Server 002 chkSvs :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1771059345.416 S -> chkSvs: :My.Little.Server 003 chkSvs :This server was created 2026-02-14 08:55:45.319289533 UTC 1771059345.417 S -> chkSvs: :My.Little.Server 004 chkSvs My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1771059345.417 S -> chkSvs: :My.Little.Server 005 chkSvs EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1771059345.417 S -> chkSvs: :My.Little.Server 422 chkSvs :MOTD File is missing 1771059345.417 S -> chkSvs: :chkSvs!chk@localhost MODE chkSvs + 1771059345.417 S -> chkSvs: :My.Little.Server NOTICE chkSvs :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1771059345.417 chkSvs -> S: CAP LS 302 1771059345.417 sable_ircd #x1B[2m2026-02-14T08:55:45.416994Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059345.417 sable_ircd #x1B[2m2026-02-14T08:55:45.417059Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"CAP LS 302" 1771059345.417 sable_ircd #x1B[2m2026-02-14T08:55:45.417199Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server CAP * LS :message-tags server-time echo-message sasl=PLAIN,EXTERNAL batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1771059345.467 chkSvs -> S: PING synchronize373.532964908 1771059345.467 S -> chkSvs: :My.Little.Server CAP * LS :message-tags server-time echo-message sasl=PLAIN,EXTERNAL batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1771059345.467 sable_ircd #x1B[2m2026-02-14T08:55:45.467051Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059345.467 sable_ircd #x1B[2m2026-02-14T08:55:45.467116Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize373.532964908" 1771059345.467 sable_ircd #x1B[2m2026-02-14T08:55:45.467245Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): :My.Little.Server PONG My.Little.Server :synchronize373.532964908 1771059345.468 S -> chkSvs: :My.Little.Server PONG My.Little.Server :synchronize373.532964908 1771059345.468 chkSvs -> S: QUIT 1771059345.468 chkSvs -> S: PING synchronize373.533842337 1771059345.468 sable_ircd #x1B[2m2026-02-14T08:55:45.467877Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059345.468 sable_ircd #x1B[2m2026-02-14T08:55:45.467930Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"QUIT" 1771059345.468 sable_ircd #x1B[2m2026-02-14T08:55:45.468016Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059345.468 sable_ircd #x1B[2m2026-02-14T08:55:45.468065Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize373.533842337" 1771059346.160 sable_ircd #x1B[2m2026-02-14T08:55:46.160054Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from reap_preclients_timer 1771059346.160 sable_ircd #x1B[2m2026-02-14T08:55:46.160094Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434098249076739)), timestamp: 1771059346, clock: EventClock(1 => 810433847064793090), target: Server(ServerId(1)), details: ServerPing(ServerPing { ts: 1771059346 }) }; event clock=EventClock(1 => 810433847064793090) my clock=EventClock(1 => 810433847064793090) 1771059346.160 sable_ircd #x1B[2m2026-02-14T08:55:46.160157Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m No peers available to propagate message 1771059346.160 sable_ircd #x1B[2m2026-02-14T08:55:46.160357Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434098249076739)), timestamp: 1771059346, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059346.160 sable_ircd #x1B[2m2026-02-14T08:55:46.160390Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434098249076739)), timestamp: 1771059346, change: EventComplete(EventComplete), users_to_notify: [] } 1771059346.221 sable_ircd #x1B[2m2026-02-14T08:55:46.220797Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 2): ERROR :Client quit 1771059346.221 S -> chkSvs: ERROR :Client quit 1771059346.221 sable_ircd #x1B[2m2026-02-14T08:55:46.220936Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mCloseConnection(ConnectionId(ListenerId(0), 2)) 1771059346.221 sable_ircd #x1B[2m2026-02-14T08:55:46.220975Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::connection_collection#x1B[0m#x1B[2m:#x1B[0m Removing connection ConnectionId(ListenerId(0), 2) 1771059346.221 sable_ircd #x1B[2m2026-02-14T08:55:46.221181Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 810434094717472770, 99 => 810434094705291266) my clock=EventClock(1 => 810434094717472770, 99 => 810434094705291266) 1771059346.222 chkSvs: disconnects from server. 1771059346.222 sable_ircd #x1B[2m2026-02-14T08:55:46.221536Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34379 (127.0.0.1:34379) 1771059346.222 1: connects to server. 1771059346.222 1 -> S: NICK jilles 1771059346.222 1 -> S: USER r e g :user 1771059346.222 sable_ircd #x1B[2m2026-02-14T08:55:46.222092Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m Connection error #x1B[3mconnection#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 2) #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mClosed 1771059346.222 sable_ircd #x1B[2m2026-02-14T08:55:46.222193Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059346.223 sable_ircd #x1B[2m2026-02-14T08:55:46.222955Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m got new connection #x1B[3mdata#x1B[0m#x1B[2m=#x1B[0mConnectionData { id: ConnectionId(ListenerId(0), 3), remote_addr: 127.0.0.1, tls_info: None } 1771059346.223 sable_ircd #x1B[2m2026-02-14T08:55:46.223064Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059346.223 sable_ircd #x1B[2m2026-02-14T08:55:46.223108Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got new connection 1771059346.223 sable_ircd #x1B[2m2026-02-14T08:55:46.223163Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server NOTICE * :*** Looking up your hostname 1771059346.223 sable_ircd #x1B[2m2026-02-14T08:55:46.223294Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059346.223 sable_ircd #x1B[2m2026-02-14T08:55:46.223339Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"NICK jilles" 1771059346.224 sable_ircd #x1B[2m2026-02-14T08:55:46.223479Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 374, tv_nsec: 289401150 }, attach_user_id: OnceLock(), user: OnceLock(), extra_user_params: OnceLock(), nick: OnceLock(Nickname("jilles")), realname: OnceLock(), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059346.224 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname 1771059346.224 sable_ircd #x1B[2m2026-02-14T08:55:46.223558Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from async_handlers 1771059346.224 sable_ircd #x1B[2m2026-02-14T08:55:46.223619Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059346.224 sable_ircd #x1B[2m2026-02-14T08:55:46.223670Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"USER r e g :user" 1771059346.224 sable_ircd #x1B[2m2026-02-14T08:55:46.223791Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 374, tv_nsec: 289401150 }, attach_user_id: OnceLock(), user: OnceLock(Username("r")), extra_user_params: OnceLock(("e", "g")), nick: OnceLock(Nickname("jilles")), realname: OnceLock(Realname("user")), hostname: OnceLock(), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059346.224 sable_ircd #x1B[2m2026-02-14T08:55:46.224442Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from auth_events 1771059346.225 sable_ircd #x1B[2m2026-02-14T08:55:46.224477Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m DNS lookup finished for ConnectionId(ListenerId(0), 3): 127.0.0.1/Some(Hostname("localhost")) 1771059346.225 sable_ircd #x1B[2m2026-02-14T08:55:46.224510Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server NOTICE * :*** Found your hostname: localhost 1771059346.225 sable_ircd #x1B[2m2026-02-14T08:55:46.224541Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 374, tv_nsec: 289401150 }, attach_user_id: OnceLock(), user: OnceLock(Username("r")), extra_user_params: OnceLock(("e", "g")), nick: OnceLock(Nickname("jilles")), realname: OnceLock(Realname("user")), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 0 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mtrue #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059346.225 sable_ircd #x1B[2m2026-02-14T08:55:46.224596Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mRegisterClient(ConnectionId(ListenerId(0), 3)) 1771059346.225 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1771059346.226 sable_ircd #x1B[2m2026-02-14T08:55:46.225215Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 810434098504929283, 99 => 810434094705291266) my clock=EventClock(1 => 810434098504929283, 99 => 810434094705291266) 1771059346.226 sable_ircd #x1B[2m2026-02-14T08:55:46.225659Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(810434094717472768)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(810434094717472769)), user: UserId(Snowflake(810434094717472768)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059345 } }), users_to_notify: [] }) 1771059346.226 sable_ircd #x1B[2m2026-02-14T08:55:46.225713Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(810434094717472768)), 2), connection: UserConnection { id: UserConnectionId(Snowflake(810434094717472769)), user: UserId(Snowflake(810434094717472768)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059345 } }), users_to_notify: [] } 1771059346.226 sable_ircd #x1B[2m2026-02-14T08:55:46.225803Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059346.226 sable_ircd #x1B[2m2026-02-14T08:55:46.225836Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, change: EventComplete(EventComplete), users_to_notify: [] } 1771059346.226 sable_ircd #x1B[2m2026-02-14T08:55:46.225989Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34379 (127.0.0.1:34379) 1771059346.228 sable_ircd #x1B[2m2026-02-14T08:55:46.227592Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, clock: EventClock(1 => 810434098521706500, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434098517512195)), NewUserConnection { user: UserId(Snowflake(810434098517512194)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059346 })) }) }; event clock=EventClock(1 => 810434098521706500, 99 => 810434094705291266) my clock=EventClock(1 => 810434098521706500, 99 => 810434094705291266) 1771059346.228 sable_ircd #x1B[2m2026-02-14T08:55:46.227901Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, clock: EventClock(1 => 810434098521706500, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434098517512195)), NewUserConnection { user: UserId(Snowflake(810434098517512194)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059346 })) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34379 (127.0.0.1:34379) 1771059346.228 sable_ircd #x1B[2m2026-02-14T08:55:46.228090Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(810434094717472768)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] }) 1771059346.228 sable_ircd #x1B[2m2026-02-14T08:55:46.228120Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(810434094717472768)), 2), nickname: Nickname("chkSvs"), message: "Client Quit", memberships: [] }), users_to_notify: [] } 1771059346.228 sable_ircd #x1B[2m2026-02-14T08:55:46.228158Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059346.228 sable_ircd #x1B[2m2026-02-14T08:55:46.228174Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, change: EventComplete(EventComplete), users_to_notify: [] } 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.231512Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(810434098517512194)), 1) }), users_to_notify: [] }) 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.231552Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, change: NewUser(NewUser { user: HistoricUserId(UserId(Snowflake(810434098517512194)), 1) }), users_to_notify: [] } 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.231621Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(810434098517512194)), 2), connection: UserConnectionId(Snowflake(810434098517512195)) }), users_to_notify: [] }) 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.231656Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, change: NewUserConnection(NewUserConnection { user: HistoricUserId(UserId(Snowflake(810434098517512194)), 2), connection: UserConnectionId(Snowflake(810434098517512195)) }), users_to_notify: [] } 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.231731Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 001 jilles :Welcome to the test Internet Relay Chat network, jilles 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.231801Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 002 jilles :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.231854Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 003 jilles :This server was created 2026-02-14 08:55:46.231838032 UTC 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.231900Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 004 jilles My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.231953Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 005 jilles EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.231997Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server 422 jilles :MOTD File is missing 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.232044Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :jilles!r@localhost MODE jilles + 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.232088Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server NOTICE jilles :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.232156Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059346.232 sable_ircd #x1B[2m2026-02-14T08:55:46.232190Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, change: EventComplete(EventComplete), users_to_notify: [] } 1771059346.233 S -> 1: :My.Little.Server 001 jilles :Welcome to the test Internet Relay Chat network, jilles 1771059346.233 S -> 1: :My.Little.Server 002 jilles :Your host is My.Little.Server, running version sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 1771059346.233 S -> 1: :My.Little.Server 003 jilles :This server was created 2026-02-14 08:55:46.231838032 UTC 1771059346.233 S -> 1: :My.Little.Server 004 jilles My.Little.Server sable-0.1.0-70e61b4cc015537d8906da5286f062a8199fb432 ioZ ntsim bqeIkov 1771059346.233 sable_services #x1B[2m2026-02-14T08:55:46.232462Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, clock: EventClock(1 => 810434098521706500, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434098517512195)), NewUserConnection { user: UserId(Snowflake(810434098517512194)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059346 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, clock: EventClock(1 => 810434098521706500, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434098517512195)), NewUserConnection { user: UserId(Snowflake(810434098517512194)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059346 })) }) }; event clock=EventClock(1 => 810434098521706500, 99 => 810434094705291266) my clock=EventClock(1 => 810434094230933505, 99 => 810434094705291266) 1771059346.233 sable_services #x1B[2m2026-02-14T08:55:46.232571Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, clock: EventClock(1 => 810434098521706500, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434098517512195)), NewUserConnection { user: UserId(Snowflake(810434098517512194)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059346 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(810434098521706500))] 1771059346.233 sable_services #x1B[2m2026-02-14T08:55:46.233051Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, clock: EventClock(1 => 810434098521706500, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434098517512195)), NewUserConnection { user: UserId(Snowflake(810434098517512194)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059346 })) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, clock: EventClock(1 => 810434098521706500, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434098517512195)), NewUserConnection { user: UserId(Snowflake(810434098517512194)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059346 })) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34905 (127.0.0.1:34905) 1771059346.266 sable_services #x1B[2m2026-02-14T08:55:46.266023Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 810434094717472770, 99 => 810434094705291266) my clock=EventClock(1 => 810434094230933505, 99 => 810434094705291266) 1771059346.266 sable_services #x1B[2m2026-02-14T08:55:46.266084Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(810434094717472770))] 1771059346.266 sable_services #x1B[2m2026-02-14T08:55:46.266405Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34905 (127.0.0.1:34905) 1771059346.269 sable_services #x1B[2m2026-02-14T08:55:46.268983Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 810434098504929283, 99 => 810434094705291266) my clock=EventClock(1 => 810434094230933505, 99 => 810434094705291266) 1771059346.269 sable_services #x1B[2m2026-02-14T08:55:46.269030Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(810434098504929283))] 1771059346.269 sable_services #x1B[2m2026-02-14T08:55:46.269350Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34905 (127.0.0.1:34905) 1771059346.274 sable_ircd #x1B[2m2026-02-14T08:55:46.273980Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: GetEvent([EventId(Snowflake(810434098521706500))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(810434098521706500))] 1771059346.274 sable_ircd #x1B[2m2026-02-14T08:55:46.274024Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: GetEvent([EventId(Snowflake(810434098521706500))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) }] 1771059346.308 sable_ircd #x1B[2m2026-02-14T08:55:46.307922Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: GetEvent([EventId(Snowflake(810434094717472770))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(810434094717472770))] 1771059346.308 sable_ircd #x1B[2m2026-02-14T08:55:46.307965Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: GetEvent([EventId(Snowflake(810434094717472770))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: User(UserId(Snowflake(810434094717472768))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434094717472769)), NewUserConnection { user: UserId(Snowflake(810434094717472768)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059345 })) }) }] 1771059346.310 sable_ircd #x1B[2m2026-02-14T08:55:46.309946Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: GetEvent([EventId(Snowflake(810434098504929283))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(810434098504929283))] 1771059346.310 sable_ircd #x1B[2m2026-02-14T08:55:46.309994Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: GetEvent([EventId(Snowflake(810434098504929283))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) }] 1771059346.315 sable_services #x1B[2m2026-02-14T08:55:46.314975Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: BulkEvents([Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) }] 1771059346.315 sable_services #x1B[2m2026-02-14T08:55:46.315038Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: BulkEvents([Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mfalse#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 810434098504929283, 99 => 810434094705291266) my clock=EventClock(1 => 810434094230933505, 99 => 810434094705291266) 1771059346.315 sable_services #x1B[2m2026-02-14T08:55:46.315081Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: BulkEvents([Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mfalse#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(810434098504929283))] 1771059346.333 1 -> S: PING synchronize374.398976467 1771059346.333 S -> 1: :My.Little.Server 005 jilles EXCEPTS INVEX FNC UTF8ONLY MONITOR=64 CASEMAPPING=ascii CLIENTTAGDENY=* HOSTLEN=64 NICKLEN=15 USERLEN=10 CHANMODES=bqeI,k,,ntsim MSGREFTYPES=timestamp PREFIX=(ov)@+ :are supported by this server 1771059346.333 S -> 1: :My.Little.Server 422 jilles :MOTD File is missing 1771059346.333 S -> 1: :jilles!r@localhost MODE jilles + 1771059346.333 S -> 1: :My.Little.Server NOTICE jilles :The network is currently running in debug mode. Do not send any sensitive information such as passwords. 1771059346.333 sable_ircd #x1B[2m2026-02-14T08:55:46.333064Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059346.333 sable_ircd #x1B[2m2026-02-14T08:55:46.333106Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize374.398976467" 1771059346.333 sable_ircd #x1B[2m2026-02-14T08:55:46.333186Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server PONG My.Little.Server :synchronize374.398976467 1771059346.333 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize374.398976467 1771059346.334 1 -> S: REGISTER * * sesame 1771059346.334 sable_ircd #x1B[2m2026-02-14T08:55:46.333837Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059346.334 sable_ircd #x1B[2m2026-02-14T08:55:46.333878Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"REGISTER * * sesame" 1771059346.334 sable_ircd #x1B[2m2026-02-14T08:55:46.334045Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Found target peer, sending message #x1B[3mtarget#x1B[0m#x1B[2m=#x1B[0mPeerConfig { name: ServerName("My.Little.Services"), address: "127.0.0.1:34379", fingerprint: "8810c8f1088defe88fa294587a8eac24c133965e" } #x1B[3mdetail#x1B[0m#x1B[2m=#x1B[0mTargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(RegisterUser(Nickname("jilles"), "sesame")) } 1771059346.334 sable_ircd #x1B[2m2026-02-14T08:55:46.334230Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059345), content: TargetedMessage(TargetedMessage { source: ServerName("My.Little.Server"), target: ServerName("My.Little.Services"), via: [], content: Services(RegisterUser(Nickname("jilles"), "sesame")) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34379 (127.0.0.1:34379) 1771059346.336 sable_ircd #x1B[2m2026-02-14T08:55:46.335901Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got send result #x1B[3msend_result#x1B[0m#x1B[2m=#x1B[0mOk(JoinHandle { id: Id(26) }) 1771059346.349 sable_services #x1B[2m2026-02-14T08:55:46.349044Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: BulkEvents([Event { id: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: User(UserId(Snowflake(810434094717472768))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434094717472769)), NewUserConnection { user: UserId(Snowflake(810434094717472768)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059345 })) }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: User(UserId(Snowflake(810434094717472768))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434094717472769)), NewUserConnection { user: UserId(Snowflake(810434094717472768)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059345 })) }) }] 1771059346.349 sable_services #x1B[2m2026-02-14T08:55:46.349119Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: BulkEvents([Event { id: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: User(UserId(Snowflake(810434094717472768))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434094717472769)), NewUserConnection { user: UserId(Snowflake(810434094717472768)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059345 })) }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mfalse#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: User(UserId(Snowflake(810434094717472768))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434094717472769)), NewUserConnection { user: UserId(Snowflake(810434094717472768)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059345 })) }) }; event clock=EventClock(1 => 810434094230933505) my clock=EventClock(1 => 810434094230933505, 99 => 810434094705291266) 1771059346.349 sable_services #x1B[2m2026-02-14T08:55:46.349190Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: BulkEvents([Event { id: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: User(UserId(Snowflake(810434094717472768))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434094717472769)), NewUserConnection { user: UserId(Snowflake(810434094717472768)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059345 })) }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mfalse#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) } 1771059346.349 sable_services #x1B[2m2026-02-14T08:55:46.349235Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: BulkEvents([Event { id: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: User(UserId(Snowflake(810434094717472768))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434094717472769)), NewUserConnection { user: UserId(Snowflake(810434094717472768)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059345 })) }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mfalse#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(810434098521706500)), timestamp: 1771059346, clock: EventClock(1 => 810434098504929283, 99 => 810434094705291266), target: User(UserId(Snowflake(810434094717472768))), details: UserQuit(UserQuit { message: "Client Quit" }) } 1771059346.349 sable_services #x1B[2m2026-02-14T08:55:46.349278Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: BulkEvents([Event { id: EventId(Snowflake(810434094717472770)), timestamp: 1771059345, clock: EventClock(1 => 810434094230933505), target: User(UserId(Snowflake(810434094717472768))), details: NewUser(NewUser { nickname: Nickname("chkSvs"), username: Username("chk"), visible_hostname: Hostname("localhost"), realname: Realname("chk"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434094717472769)), NewUserConnection { user: UserId(Snowflake(810434094717472768)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059345 })) }) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mfalse#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(810434098530095109)), timestamp: 1771059346, clock: EventClock(1 => 810434098521706500, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: NewUser(NewUser { nickname: Nickname("jilles"), username: Username("r"), visible_hostname: Hostname("localhost"), realname: Realname("user"), mode: UserMode { modes: UserModeSet(0) }, server: ServerId(1), account: None, initial_connection: Some((UserConnectionId(Snowflake(810434098517512195)), NewUserConnection { user: UserId(Snowflake(810434098517512194)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059346 })) }) } 1771059346.351 sable_services #x1B[2m2026-02-14T08:55:46.350998Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: BulkEvents([Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) }] 1771059346.377 sable_services #x1B[2m2026-02-14T08:55:46.377025Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got remote request #x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mServices(RegisterUser(Nickname("jilles"), "sesame")) 1771059346.377 sable_services #x1B[2m2026-02-14T08:55:46.377064Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server#x1B[0m#x1B[2m:#x1B[0m Got register request #x1B[3maccount_name#x1B[0m#x1B[2m=#x1B[0mNickname("jilles") 1771059346.381 sable_services #x1B[2m2026-02-14T08:55:46.381192Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mhandle_remote_command#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_services::server::command::user_commands#x1B[0m#x1B[2m:#x1B[0m Successfully created account #x1B[3mnew_account#x1B[0m#x1B[2m=#x1B[0mAccount { id: AccountId(Snowflake(810434099159642112)), name: Nickname("jilles"), authorised_fingerprints: [] } 1771059346.381 sable_services #x1B[2m2026-02-14T08:55:46.381376Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434099176419331)), timestamp: 1771059346, clock: EventClock(1 => 810434098530095109, 99 => 810434094705291266), target: Account(AccountId(Snowflake(810434099159642112))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(810434099159642112)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }; event clock=EventClock(1 => 810434098530095109, 99 => 810434094705291266) my clock=EventClock(1 => 810434098530095109, 99 => 810434094705291266) 1771059346.382 sable_services #x1B[2m2026-02-14T08:55:46.381644Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434099176419331)), timestamp: 1771059346, clock: EventClock(1 => 810434098530095109, 99 => 810434094705291266), target: Account(AccountId(Snowflake(810434099159642112))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(810434099159642112)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34905 (127.0.0.1:34905) 1771059346.422 sable_ircd #x1B[2m2026-02-14T08:55:46.421957Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got targeted message response #x1B[3mresponse#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: TargetedMessageResponse(Services(LogUserIn(AccountId(Snowflake(810434099159642112))))) } } 1771059346.422 sable_ircd #x1B[2m2026-02-14T08:55:46.422107Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: GetEvent([EventId(Snowflake(810434098504929283))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(810434098504929283))] 1771059346.422 sable_ircd #x1B[2m2026-02-14T08:55:46.422167Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: GetEvent([EventId(Snowflake(810434098504929283))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) }] 1771059346.422 sable_ircd #x1B[2m2026-02-14T08:55:46.422394Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): REGISTER SUCCESS jilles :You have successfully registered 1771059346.423 sable_ircd #x1B[2m2026-02-14T08:55:46.422469Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from async_handlers 1771059346.423 sable_ircd #x1B[2m2026-02-14T08:55:46.422525Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mStateChange(User(UserId(Snowflake(810434098517512194))), UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434099159642112))) })) 1771059346.423 sable_ircd #x1B[2m2026-02-14T08:55:46.422673Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434099347984390)), timestamp: 1771059346, clock: EventClock(1 => 810434098530095109, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434099159642112))) }) }; event clock=EventClock(1 => 810434098530095109, 99 => 810434094705291266) my clock=EventClock(1 => 810434098530095109, 99 => 810434094705291266) 1771059346.423 sable_ircd #x1B[2m2026-02-14T08:55:46.423030Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434099347984390)), timestamp: 1771059346, clock: EventClock(1 => 810434098530095109, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434099159642112))) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34379 (127.0.0.1:34379) 1771059346.425 sable_ircd #x1B[2m2026-02-14T08:55:46.424674Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434099176419331)), timestamp: 1771059346, clock: EventClock(1 => 810434098530095109, 99 => 810434094705291266), target: Account(AccountId(Snowflake(810434099159642112))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(810434099159642112)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434099176419331)), timestamp: 1771059346, clock: EventClock(1 => 810434098530095109, 99 => 810434094705291266), target: Account(AccountId(Snowflake(810434099159642112))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(810434099159642112)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }; event clock=EventClock(1 => 810434098530095109, 99 => 810434094705291266) my clock=EventClock(1 => 810434099347984390, 99 => 810434094705291266) 1771059346.425 sable_ircd #x1B[2m2026-02-14T08:55:46.425200Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434099176419331)), timestamp: 1771059346, clock: EventClock(1 => 810434098530095109, 99 => 810434094705291266), target: Account(AccountId(Snowflake(810434099159642112))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(810434099159642112)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434099176419331)), timestamp: 1771059346, clock: EventClock(1 => 810434098530095109, 99 => 810434094705291266), target: Account(AccountId(Snowflake(810434099159642112))), details: AccountUpdate(AccountUpdate { data: Some(Account { id: AccountId(Snowflake(810434099159642112)), name: Nickname("jilles"), authorised_fingerprints: [] }) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34379 (127.0.0.1:34379) 1771059346.429 sable_ircd #x1B[2m2026-02-14T08:55:46.429130Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434099347984390)), timestamp: 1771059346, change: UserLoginChange(UserLoginChange { user: HistoricUserId(UserId(Snowflake(810434098517512194)), 3), old_account: None, new_account: Some(AccountId(Snowflake(810434099159642112))) }), users_to_notify: [] }) 1771059346.429 sable_ircd #x1B[2m2026-02-14T08:55:46.429174Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434099347984390)), timestamp: 1771059346, change: UserLoginChange(UserLoginChange { user: HistoricUserId(UserId(Snowflake(810434098517512194)), 3), old_account: None, new_account: Some(AccountId(Snowflake(810434099159642112))) }), users_to_notify: [] } 1771059346.429 sable_ircd #x1B[2m2026-02-14T08:55:46.429238Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434099347984390)), timestamp: 1771059346, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059346.429 sable_ircd #x1B[2m2026-02-14T08:55:46.429274Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434099347984390)), timestamp: 1771059346, change: EventComplete(EventComplete), users_to_notify: [] } 1771059346.429 sable_ircd #x1B[2m2026-02-14T08:55:46.429333Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434099176419331)), timestamp: 1771059346, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059346.429 sable_ircd #x1B[2m2026-02-14T08:55:46.429373Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434099176419331)), timestamp: 1771059346, change: EventComplete(EventComplete), users_to_notify: [] } 1771059346.463 sable_services #x1B[2m2026-02-14T08:55:46.463000Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: BulkEvents([Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(810434098504929283)), timestamp: 1771059346, clock: EventClock(1 => 810434094717472770, 99 => 810434094705291266), target: UserConnection(UserConnectionId(Snowflake(810434094717472769))), details: UserDisconnect(UserDisconnect) }] 1771059346.466 sable_services #x1B[2m2026-02-14T08:55:46.465970Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434099347984390)), timestamp: 1771059346, clock: EventClock(1 => 810434098530095109, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434099159642112))) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434099347984390)), timestamp: 1771059346, clock: EventClock(1 => 810434098530095109, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434099159642112))) }) }; event clock=EventClock(1 => 810434098530095109, 99 => 810434094705291266) my clock=EventClock(1 => 810434098530095109, 99 => 810434099176419331) 1771059346.466 sable_services #x1B[2m2026-02-14T08:55:46.466316Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434099347984390)), timestamp: 1771059346, clock: EventClock(1 => 810434098530095109, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434099159642112))) }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434099347984390)), timestamp: 1771059346, clock: EventClock(1 => 810434098530095109, 99 => 810434094705291266), target: User(UserId(Snowflake(810434098517512194))), details: UserLogin(UserLogin { account: Some(AccountId(Snowflake(810434099159642112))) }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34905 (127.0.0.1:34905) 1771059346.534 1 -> S: PING synchronize374.599965068 1771059346.534 S -> 1: REGISTER SUCCESS jilles :You have successfully registered 1771059346.534 sable_ircd #x1B[2m2026-02-14T08:55:46.534175Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059346.534 sable_ircd #x1B[2m2026-02-14T08:55:46.534231Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize374.599965068" 1771059347.036 sable_ircd #x1B[2m2026-02-14T08:55:47.036385Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): :My.Little.Server PONG My.Little.Server :synchronize374.599965068 1771059347.037 S -> 1: :My.Little.Server PONG My.Little.Server :synchronize374.599965068 1771059347.037 1 -> S: QUIT 1771059347.037 sable_ircd #x1B[2m2026-02-14T08:55:47.037273Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059347.037 sable_ircd #x1B[2m2026-02-14T08:55:47.037334Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"QUIT" 1771059347.137 1 -> S: PING synchronize375.203312016 1771059347.138 sable_ircd #x1B[2m2026-02-14T08:55:47.137511Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059347.138 sable_ircd #x1B[2m2026-02-14T08:55:47.137560Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"PING synchronize375.203312016" 1771059348.138 1: waiting… 1771059348.143 sable_ircd #x1B[2m2026-02-14T08:55:48.142986Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 3): ERROR :Client quit 1771059348.143 sable_ircd #x1B[2m2026-02-14T08:55:48.143121Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got pending CommandAction #x1B[3mact#x1B[0m#x1B[2m=#x1B[0mCloseConnection(ConnectionId(ListenerId(0), 3)) 1771059348.143 sable_ircd #x1B[2m2026-02-14T08:55:48.143160Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::connection_collection#x1B[0m#x1B[2m:#x1B[0m Removing connection ConnectionId(ListenerId(0), 3) 1771059348.143 sable_ircd #x1B[2m2026-02-14T08:55:48.143288Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, clock: EventClock(1 => 810434099347984390, 99 => 810434099176419331), target: UserConnection(UserConnectionId(Snowflake(810434098517512195))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 810434099347984390, 99 => 810434099176419331) my clock=EventClock(1 => 810434099347984390, 99 => 810434099176419331) 1771059348.143 S -> 1: ERROR :Client quit 1771059348.144 sable_ircd #x1B[2m2026-02-14T08:55:48.143686Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, clock: EventClock(1 => 810434099347984390, 99 => 810434099176419331), target: UserConnection(UserConnectionId(Snowflake(810434098517512195))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34379 (127.0.0.1:34379) 1771059348.144 sable_ircd #x1B[2m2026-02-14T08:55:48.144339Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m Connection error #x1B[3mconnection#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 3) #x1B[3merror#x1B[0m#x1B[2m=#x1B[0mClosed 1771059348.144 sable_ircd #x1B[2m2026-02-14T08:55:48.144435Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059348.146 sable_ircd #x1B[2m2026-02-14T08:55:48.145852Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434106574770184)), timestamp: 1771059348, clock: EventClock(1 => 810434106566381575, 99 => 810434099176419331), target: User(UserId(Snowflake(810434098517512194))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 810434106566381575, 99 => 810434099176419331) my clock=EventClock(1 => 810434106566381575, 99 => 810434099176419331) 1771059348.146 sable_ircd #x1B[2m2026-02-14T08:55:48.146116Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434106574770184)), timestamp: 1771059348, clock: EventClock(1 => 810434106566381575, 99 => 810434099176419331), target: User(UserId(Snowflake(810434098517512194))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Services#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34379 (127.0.0.1:34379) 1771059348.146 sable_ircd #x1B[2m2026-02-14T08:55:48.146231Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(810434098517512194)), 3), connection: UserConnection { id: UserConnectionId(Snowflake(810434098517512195)), user: UserId(Snowflake(810434098517512194)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059346 } }), users_to_notify: [] }) 1771059348.146 sable_ircd #x1B[2m2026-02-14T08:55:48.146274Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, change: UserConnectionDisconnected(UserConnectionDisconnected { user: HistoricUserId(UserId(Snowflake(810434098517512194)), 3), connection: UserConnection { id: UserConnectionId(Snowflake(810434098517512195)), user: UserId(Snowflake(810434098517512194)), hostname: Hostname("localhost"), ip: 127.0.0.1, connection_time: 1771059346 } }), users_to_notify: [] } 1771059348.146 sable_ircd #x1B[2m2026-02-14T08:55:48.146328Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059348.146 sable_ircd #x1B[2m2026-02-14T08:55:48.146351Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, change: EventComplete(EventComplete), users_to_notify: [] } 1771059348.150 sable_ircd #x1B[2m2026-02-14T08:55:48.150404Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434106574770184)), timestamp: 1771059348, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(810434098517512194)), 3), nickname: Nickname("jilles"), message: "Client Quit", memberships: [] }), users_to_notify: [] }) 1771059348.151 sable_ircd #x1B[2m2026-02-14T08:55:48.150488Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434106574770184)), timestamp: 1771059348, change: UserQuit(UserQuit { user: HistoricUserId(UserId(Snowflake(810434098517512194)), 3), nickname: Nickname("jilles"), message: "Client Quit", memberships: [] }), users_to_notify: [] } 1771059348.151 sable_ircd #x1B[2m2026-02-14T08:55:48.150609Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from history_receiver #x1B[3mres#x1B[0m#x1B[2m=#x1B[0mSome(NetworkHistoryUpdate { event: EventId(Snowflake(810434106574770184)), timestamp: 1771059348, change: EventComplete(EventComplete), users_to_notify: [] }) 1771059348.151 sable_ircd #x1B[2m2026-02-14T08:55:48.150680Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server::update_handler#x1B[0m#x1B[2m:#x1B[0m Got history update #x1B[3mupdate#x1B[0m#x1B[2m=#x1B[0mNetworkHistoryUpdate { event: EventId(Snowflake(810434106574770184)), timestamp: 1771059348, change: EventComplete(EventComplete), users_to_notify: [] } 1771059348.151 sable_services #x1B[2m2026-02-14T08:55:48.151280Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434106574770184)), timestamp: 1771059348, clock: EventClock(1 => 810434106566381575, 99 => 810434099176419331), target: User(UserId(Snowflake(810434098517512194))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Deferring event Event { id: EventId(Snowflake(810434106574770184)), timestamp: 1771059348, clock: EventClock(1 => 810434106566381575, 99 => 810434099176419331), target: User(UserId(Snowflake(810434098517512194))), details: UserQuit(UserQuit { message: "Client Quit" }) }; event clock=EventClock(1 => 810434106566381575, 99 => 810434099176419331) my clock=EventClock(1 => 810434099347984390, 99 => 810434099176419331) 1771059348.151 sable_services #x1B[2m2026-02-14T08:55:48.151352Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434106574770184)), timestamp: 1771059348, clock: EventClock(1 => 810434106566381575, 99 => 810434099176419331), target: User(UserId(Snowflake(810434098517512194))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Requesting missing IDs [EventId(Snowflake(810434106566381575))] 1771059348.152 sable_services #x1B[2m2026-02-14T08:55:48.151713Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434106574770184)), timestamp: 1771059348, clock: EventClock(1 => 810434106566381575, 99 => 810434099176419331), target: User(UserId(Snowflake(810434098517512194))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434106574770184)), timestamp: 1771059348, clock: EventClock(1 => 810434106566381575, 99 => 810434099176419331), target: User(UserId(Snowflake(810434098517512194))), details: UserQuit(UserQuit { message: "Client Quit" }) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34905 (127.0.0.1:34905) 1771059348.187 sable_services #x1B[2m2026-02-14T08:55:48.187214Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, clock: EventClock(1 => 810434099347984390, 99 => 810434099176419331), target: UserConnection(UserConnectionId(Snowflake(810434098517512195))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding event Event { id: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, clock: EventClock(1 => 810434099347984390, 99 => 810434099176419331), target: UserConnection(UserConnectionId(Snowflake(810434098517512195))), details: UserDisconnect(UserDisconnect) }; event clock=EventClock(1 => 810434099347984390, 99 => 810434099176419331) my clock=EventClock(1 => 810434099347984390, 99 => 810434099176419331) 1771059348.187 sable_services #x1B[2m2026-02-14T08:55:48.187303Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, clock: EventClock(1 => 810434099347984390, 99 => 810434099176419331), target: UserConnection(UserConnectionId(Snowflake(810434098517512195))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::eventlog#x1B[0m#x1B[2m:#x1B[0m Adding satisfied deferred event Event { id: EventId(Snowflake(810434106574770184)), timestamp: 1771059348, clock: EventClock(1 => 810434106566381575, 99 => 810434099176419331), target: User(UserId(Snowflake(810434098517512194))), details: UserQuit(UserQuit { message: "Client Quit" }) } 1771059348.188 sable_services #x1B[2m2026-02-14T08:55:48.187643Z#x1B[0m #x1B[32m INFO#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, clock: EventClock(1 => 810434099347984390, 99 => 810434099176419331), target: UserConnection(UserConnectionId(Snowflake(810434098517512195))), details: UserDisconnect(UserDisconnect) }) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_new_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3mshould_propagate#x1B[0m#x1B[2m=#x1B[0mtrue#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m#x1B[1mdo_send_to#x1B[0m#x1B[1m{#x1B[0m#x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0mMessage { source_server: (ServerId(99), 1771059345), content: NewEvent(Event { id: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, clock: EventClock(1 => 810434099347984390, 99 => 810434099176419331), target: UserConnection(UserConnectionId(Snowflake(810434098517512195))), details: UserDisconnect(UserDisconnect) }) } #x1B[3mpeer#x1B[0m#x1B[2m=#x1B[0mMy.Little.Server#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Connected to 127.0.0.1:34905 (127.0.0.1:34905) 1771059348.192 sable_ircd #x1B[2m2026-02-14T08:55:48.191944Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: GetEvent([EventId(Snowflake(810434106566381575))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got request for events [EventId(Snowflake(810434106566381575))] 1771059348.192 sable_ircd #x1B[2m2026-02-14T08:55:48.191985Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Services"), message: Message { source_server: (ServerId(99), 1771059345), content: GetEvent([EventId(Snowflake(810434106566381575))]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Sending events [Event { id: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, clock: EventClock(1 => 810434099347984390, 99 => 810434099176419331), target: UserConnection(UserConnectionId(Snowflake(810434098517512195))), details: UserDisconnect(UserDisconnect) }] 1771059348.234 sable_services #x1B[2m2026-02-14T08:55:48.234065Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1msync_task#x1B[0m#x1B[2m:#x1B[0m#x1B[1mhandle_network_request#x1B[0m#x1B[1m{#x1B[0m#x1B[3mreq#x1B[0m#x1B[2m=#x1B[0mRequest { received_from: ServerName("My.Little.Server"), message: Message { source_server: (ServerId(1), 1771059345), content: BulkEvents([Event { id: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, clock: EventClock(1 => 810434099347984390, 99 => 810434099176419331), target: UserConnection(UserConnectionId(Snowflake(810434098517512195))), details: UserDisconnect(UserDisconnect) }]) } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::replicated_log#x1B[0m#x1B[2m:#x1B[0m Got bulk events: [Event { id: EventId(Snowflake(810434106566381575)), timestamp: 1771059348, clock: EventClock(1 => 810434099347984390, 99 => 810434099176419331), target: UserConnection(UserConnectionId(Snowflake(810434098517512195))), details: UserDisconnect(UserDisconnect) }] 1771059348.244 1 -> S: PING synchronize376.309961224 1771059348.244 1: connects to server. 1771059348.244 1 -> S: CAP LS 302 1771059348.245 sable_ircd #x1B[2m2026-02-14T08:55:48.244800Z#x1B[0m #x1B[34mDEBUG#x1B[0m #x1B[1mrun_communication_task#x1B[0m#x1B[2m:#x1B[0m #x1B[2mclient_listener::listener_collection#x1B[0m#x1B[2m:#x1B[0m got new connection #x1B[3mdata#x1B[0m#x1B[2m=#x1B[0mConnectionData { id: ConnectionId(ListenerId(0), 4), remote_addr: 127.0.0.1, tls_info: None } 1771059348.245 sable_ircd #x1B[2m2026-02-14T08:55:48.244925Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059348.245 sable_ircd #x1B[2m2026-02-14T08:55:48.244964Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got new connection 1771059348.245 sable_ircd #x1B[2m2026-02-14T08:55:48.245025Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server NOTICE * :*** Looking up your hostname 1771059348.245 sable_ircd #x1B[2m2026-02-14T08:55:48.245177Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059348.245 sable_ircd #x1B[2m2026-02-14T08:55:48.245237Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"CAP LS 302" 1771059348.245 S -> 1: :My.Little.Server NOTICE * :*** Looking up your hostname 1771059348.245 sable_ircd #x1B[2m2026-02-14T08:55:48.245371Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server CAP * LS :message-tags server-time echo-message sasl=PLAIN,EXTERNAL batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1771059348.246 S -> 1: :My.Little.Server CAP * LS :message-tags server-time echo-message sasl=PLAIN,EXTERNAL batch labeled-response userhost-in-names away-notify account-tag multi-prefix draft/chathistory sable.libera.chat/persistent-session draft/account-registration draft/channel-rename 1771059348.246 1 -> S: CAP REQ :extended-monitor 1771059348.246 sable_ircd #x1B[2m2026-02-14T08:55:48.246021Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from auth_events 1771059348.246 sable_ircd #x1B[2m2026-02-14T08:55:48.246055Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m DNS lookup finished for ConnectionId(ListenerId(0), 4): 127.0.0.1/Some(Hostname("localhost")) 1771059348.246 sable_ircd #x1B[2m2026-02-14T08:55:48.246082Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server NOTICE * :*** Found your hostname: localhost 1771059348.246 sable_ircd #x1B[2m2026-02-14T08:55:48.246106Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m PreClient::can_register #x1B[3mself#x1B[0m#x1B[2m=#x1B[0mPreClient { connected_at: Instant { tv_sec: 376, tv_nsec: 311255922 }, attach_user_id: OnceLock(), user: OnceLock(), extra_user_params: OnceLock(), nick: OnceLock(), realname: OnceLock(), hostname: OnceLock(Hostname("localhost")), sasl_session: Mutex { data: None }, sasl_account: OnceLock(), progress_flags: 1 } #x1B[3mcan_register_new#x1B[0m#x1B[2m=#x1B[0mfalse #x1B[3mcan_attach#x1B[0m#x1B[2m=#x1B[0mfalse 1771059348.246 sable_ircd #x1B[2m2026-02-14T08:55:48.246292Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m ...from connection_events 1771059348.246 S -> 1: :My.Little.Server NOTICE * :*** Found your hostname: localhost 1771059348.246 sable_ircd #x1B[2m2026-02-14T08:55:48.246355Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m#x1B[1mprocess_connection_event#x1B[0m#x1B[1m{#x1B[0m#x1B[3msource#x1B[0m#x1B[2m=#x1B[0mConnectionId(ListenerId(0), 4)#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::server#x1B[0m#x1B[2m:#x1B[0m Got message #x1B[3mmsg#x1B[0m#x1B[2m=#x1B[0m"CAP REQ :extended-monitor" 1771059348.246 sable_ircd #x1B[2m2026-02-14T08:55:48.246450Z#x1B[0m #x1B[35mTRACE#x1B[0m #x1B[1mdo_run#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_ircd::client#x1B[0m#x1B[2m:#x1B[0m Sending to ConnectionId(ListenerId(0), 4): :My.Little.Server CAP * NAK :extended-monitor 1771059348.287 S -> 1: :My.Little.Server CAP * NAK :extended-monitor 1771059348.290 sable_ircd #x1B[2m2026-02-14T08:55:48.290101Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Error in outbound network sync connection: I/O error: unexpected end of file 1771059348.290 sable_ircd #x1B[2m2026-02-14T08:55:48.290292Z#x1B[0m #x1B[31mERROR#x1B[0m #x1B[1mhandle_connection#x1B[0m#x1B[1m{#x1B[0m#x1B[3mconn#x1B[0m#x1B[2m=#x1B[0mPollEvented { io: Some(TcpStream { addr: 127.0.0.1:34905, peer: 127.0.0.1:59789, fd: 19 }) } #x1B[3mmessage_sender#x1B[0m#x1B[2m=#x1B[0mUnboundedSender { chan: Tx { inner: Chan { tx: Tx { block_tail: 0x55b38999e410, tail_position: 0 }, semaphore: Semaphore(0), rx_waker: AtomicWaker, tx_count: 2, rx_fields: "..." } } }#x1B[1m}#x1B[0m#x1B[2m:#x1B[0m #x1B[2msable_network::sync::network#x1B[0m#x1B[2m:#x1B[0m Error handling message: I/O error: unexpected end of file 1771059348.297 1: disconnects from server.